[INFO] fetching crate iron_cost 0.3.0...
[INFO] testing iron_cost-0.3.0 against master#1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 for pr-154065-1
[INFO] extracting crate iron_cost 0.3.0 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate iron_cost 0.3.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate iron_cost 0.3.0
[INFO] tweaked toml for crates.io crate iron_cost 0.3.0 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate iron_cost 0.3.0 on toolchain 1f7f8ea0721a3b1eb73e6c6d25cccb371434b320
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate iron_cost 0.3.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded uuid v1.19.0
[INFO] [stderr]   Downloaded iron_types v0.3.0
[INFO] [stderr]   Downloaded arc-swap v1.7.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bf22e7fd09d34eb32630a8018605208e15f35782f2da11802b1d035d09341472
[INFO] running `Command { std: "docker" "start" "-a" "bf22e7fd09d34eb32630a8018605208e15f35782f2da11802b1d035d09341472", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bf22e7fd09d34eb32630a8018605208e15f35782f2da11802b1d035d09341472", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bf22e7fd09d34eb32630a8018605208e15f35782f2da11802b1d035d09341472", kill_on_drop: false }`
[INFO] [stdout] bf22e7fd09d34eb32630a8018605208e15f35782f2da11802b1d035d09341472
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4116b359dfe07fb55cc4048ecfc047a2cb9397c28612245e5cbfe651ad9dc0ad
[INFO] running `Command { std: "docker" "start" "-a" "4116b359dfe07fb55cc4048ecfc047a2cb9397c28612245e5cbfe651ad9dc0ad", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling serde_json v1.0.145
[INFO] [stderr]    Compiling tracing-core v0.1.35
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling arc-swap v1.7.1
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.7
[INFO] [stderr]    Compiling socket2 v0.6.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling uuid v1.19.0
[INFO] [stderr]    Compiling dashmap v5.5.3
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling tokio v1.48.0
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling iron_types v0.3.0
[INFO] [stderr]    Compiling iron_cost v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.28s
[INFO] running `Command { std: "docker" "inspect" "4116b359dfe07fb55cc4048ecfc047a2cb9397c28612245e5cbfe651ad9dc0ad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4116b359dfe07fb55cc4048ecfc047a2cb9397c28612245e5cbfe651ad9dc0ad", kill_on_drop: false }`
[INFO] [stdout] 4116b359dfe07fb55cc4048ecfc047a2cb9397c28612245e5cbfe651ad9dc0ad
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7675dcbc0dea502ee7226990d8940c7ecabae023a8fa74e07b701ae0fbe6039c
[INFO] running `Command { std: "docker" "start" "-a" "7675dcbc0dea502ee7226990d8940c7ecabae023a8fa74e07b701ae0fbe6039c", kill_on_drop: false }`
[INFO] [stderr]    Compiling iron_types v0.3.0
[INFO] [stderr]    Compiling iron_cost v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.11s
[INFO] running `Command { std: "docker" "inspect" "7675dcbc0dea502ee7226990d8940c7ecabae023a8fa74e07b701ae0fbe6039c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7675dcbc0dea502ee7226990d8940c7ecabae023a8fa74e07b701ae0fbe6039c", kill_on_drop: false }`
[INFO] [stdout] 7675dcbc0dea502ee7226990d8940c7ecabae023a8fa74e07b701ae0fbe6039c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] c7049cac7ec2e7acbe0364b4a14297654773f9cc4f33066ec0296b0e35908e49
[INFO] running `Command { std: "docker" "start" "-a" "c7049cac7ec2e7acbe0364b4a14297654773f9cc4f33066ec0296b0e35908e49", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/iron_cost-be88cfbcb4dc5176)
[INFO] [stderr]      Running tests/budget_test.rs (/opt/rustwide/target/debug/deps/budget_test-898d42b81c1044b9)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 38 tests
[INFO] [stdout] test add_spend_accumulates ... ok
[INFO] [stdout] test add_spend_with_small_amounts ... ok
[INFO] [stdout] test cancel_allows_new_reservations ... ok
[INFO] [stdout] test check_budget_fails_when_at_limit ... ok
[INFO] [stdout] test commit_one_of_multiple_reservations ... ok
[INFO] [stdout] test commit_allows_actual_cost_less_than_reserved ... ok
[INFO] [stdout] test check_budget_returns_correct_error_values ... ok
[INFO] [stdout] test commit_add_spend_first_then_release_reservation ... ok
[INFO] [stdout] test commit_allows_actual_cost_equal_to_reserved ... ok
[INFO] [stdout] test get_full_status_returns_all_values ... ok
[INFO] [stdout] test handles_large_amounts ... ok
[INFO] [stdout] test multiple_reservations_accumulate ... ok
[INFO] [stdout] test multiple_reservations_block_overspend ... ok
[INFO] [stdout] test new_controller_has_zero_spent ... ok
[INFO] [stdout] test new_controller_with_zero_budget ... ok
[INFO] [stdout] test precision_at_microdollar_level ... ok
[INFO] [stdout] test reserve_exact_available_amount ... ok
[INFO] [stdout] test reserve_fails_when_budget_already_spent ... ok
[INFO] [stdout] test reserve_fails_when_insufficient_budget ... ok
[INFO] [stdout] test reserve_fails_when_budget_already_reserved ... ok
[INFO] [stdout] test reserve_one_more_than_available_fails ... ok
[INFO] [stdout] test reserve_updates_reserved_amount ... ok
[INFO] [stdout] test reserve_succeeds_when_budget_available ... ok
[INFO] [stdout] test reserve_with_microdollar_precision ... ok
[INFO] [stdout] test set_budget_can_unblock_exceeded_budget ... ok
[INFO] [stdout] test reserve_zero_succeeds ... ok
[INFO] [stdout] test set_budget_updates_limit ... ok
[INFO] [stdout] test set_budget_can_block_previously_valid_budget ... ok
[INFO] [stdout] test zero_budget_fails_immediately ... ok
[INFO] [stdout] test cancel_releases_reservation_without_spend ... ok
[INFO] [stdout] test commit_zero_actual_cost ... ok
[INFO] [stdout] test check_budget_includes_reserved_in_error ... ok
[INFO] [stdout] test reserve_reduces_available_budget ... ok
[INFO] [stdout] test check_budget_passes_when_under_limit ... ok
[INFO] [stdout] test new_controller_has_zero_reserved ... ok
[INFO] [stdout] test check_budget_fails_when_over_limit ... ok
[INFO] [stdout] test check_budget_fails_when_spent_plus_reserved_at_limit ... ok
[INFO] [stderr]      Running tests/converter_test.rs (/opt/rustwide/target/debug/deps/converter_test-afe7447c4c5d4de2)
[INFO] [stdout] test commit_releases_reservation_and_adds_spend ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test micros_per_mtok_to_usd_per_token_converts_correctly ... ok
[INFO] [stdout] test negative_clamps_to_zero ... ok
[INFO] [stdout] test per_token_roundtrip_conversion ... ok
[INFO] [stdout] test roundtrip_preserves_precision ... ok
[INFO] [stdout] test usd_to_micros_converts_correctly ... ok
[INFO] [stdout] test usd_per_token_to_micros_per_mtok_converts_correctly ... ok
[INFO] [stdout] test micros_to_usd_converts_correctly ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/pricing_test.rs (/opt/rustwide/target/debug/deps/pricing_test-c48f6170043d8a76)
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test converter_usd_to_micros ... ok
[INFO] [stdout] test converter_micros_to_usd ... ok
[INFO] [stdout] test calculate_cost_micros_precision ... ok
[INFO] [stdout] test calculate_cost_micros_returns_integer ... ok
[INFO] [stdout] test calculate_cost_input_only ... ok
[INFO] [stdout] test calculate_max_cost_uses_default_128000_when_no_max ... ok
[INFO] [stdout] test estimate_max_cost_returns_none_for_unknown_model ... ok
[INFO] [stdout] test calculate_cost_with_zero_tokens ... ok
[INFO] [stdout] test estimate_max_cost_uses_128000_fallback_when_no_model_max ... ok
[INFO] [stdout] test calculate_max_cost_uses_model_max_when_no_request_max ... ok
[INFO] [stdout] test has_valid_pricing_true_for_nonzero_input_cost ... ok
[INFO] [stdout] test get_returns_existing_model ... ok
[INFO] [stdout] test calculate_max_cost_uses_request_max_output ... ok
[INFO] [stdout] test get_returns_none_for_nonexistent_model ... ok
[INFO] [stdout] test has_valid_pricing_true_for_nonzero_output_cost ... ok
[INFO] [stdout] test estimate_max_cost_handles_content_blocks ... ok
[INFO] [stdout] test load_from_file_filters_sample_spec ... ok
[INFO] [stdout] test estimate_max_cost_returns_none_for_invalid_json ... ok
[INFO] [stdout] test load_from_file_filters_zero_cost_models ... ok
[INFO] [stdout] test calculate_cost_mixed ... ok
[INFO] [stdout] test load_from_file_sets_name_from_key ... ok
[INFO] [stdout] test estimate_max_cost_uses_max_completion_tokens ... ok
[INFO] [stdout] test model_name_returns_correct_value ... ok
[INFO] [stdout] test real_model_gpt51_exists ... ok
[INFO] [stdout] test load_from_file_parses_valid_json ... ok
[INFO] [stdout] test calculate_max_cost_caps_at_model_max ... ok
[INFO] [stdout] test real_model_gpt51_pricing ... ok
[INFO] [stdout] test model_max_output_tokens_prefers_max_output_tokens_field ... ok
[INFO] [stdout] test estimate_max_cost_returns_none_for_missing_model ... ok
[INFO] [stdout] test load_from_file_returns_error_for_invalid_json ... ok
[INFO] [stdout] test estimate_max_cost_minimum_1000_tokens_when_no_content ... ok
[INFO] [stdout] test estimate_max_cost_handles_anthropic_system_prompt ... ok
[INFO] [stdout] test new_creates_manager_with_embedded_data ... ok
[INFO] [stdout] test real_model_gpt51_calculate_max_cost ... ok
[INFO] [stdout] test calculate_cost_output_only ... ok
[INFO] [stdout] test real_model_gpt51_calculate_cost ... ok
[INFO] [stdout] test estimate_max_cost_estimates_input_from_messages ... ok
[INFO] [stdout] test model_max_output_tokens_falls_back_to_max_tokens ... ok
[INFO] [stdout] test estimate_max_cost_uses_model_max_output_tokens_when_no_max_tokens ... ok
[INFO] [stdout] test model_max_tokens_returns_raw_value ... ok
[INFO] [stderr]    Doc-tests iron_cost
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.29s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test src/lib.rs - (line 60) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 74) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 43) - compile ... ok
[INFO] [stdout] test src/converter.rs - converter::micros_to_usd (line 29) ... ok
[INFO] [stdout] test src/converter.rs - converter::usd_to_micros (line 17) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.59s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "c7049cac7ec2e7acbe0364b4a14297654773f9cc4f33066ec0296b0e35908e49", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c7049cac7ec2e7acbe0364b4a14297654773f9cc4f33066ec0296b0e35908e49", kill_on_drop: false }`
[INFO] [stdout] c7049cac7ec2e7acbe0364b4a14297654773f9cc4f33066ec0296b0e35908e49
